05-13 18:30:51 D/ModuleListener: ModuleListener.testStarted(com.google.android.assistant.tvts.AssistantPluginHandler#runAssistantLatencyTests) on SKBFXYZO0C1V
05-13 18:30:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.sdk] with timeout: 45s
05-13 18:30:51 D/DeviceHostUtils: apiLevel: 31
05-13 18:30:51 D/RunUtil: Running command null without timeout.
05-13 18:30:51 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:51 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
05-13 18:30:52 I/AppRunnerUtils: Service PID = 21174
05-13 18:30:52 D/RunUtil: Running command null without timeout.
05-13 18:30:52 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:52 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
05-13 18:30:52 I/AppRunnerUtils: Service PID = 21174
05-13 18:30:52 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:30:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:30:52 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:30:52 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:30:52 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
05-13 18:30:53 I/NativeDeviceStateMonitor: Waiting 359836 ms for device SKBFXYZO0C1V external store
05-13 18:30:55 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
05-13 18:30:56 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
05-13 18:30:56 D/AssistantTestUtils: Assistant successfully launched: true
05-13 18:30:57 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
05-13 18:30:57 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
05-13 18:30:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 18:30:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 18:30:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 18:30:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 18:30:58 D/RunUtil: Running command null without timeout.
05-13 18:30:58 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:58 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
05-13 18:30:58 I/AppRunnerUtils: Service PID = 22039
05-13 18:30:58 D/RunUtil: Running command null without timeout.
05-13 18:30:58 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:58 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
05-13 18:30:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
05-13 18:30:58 I/AppRunnerUtils: Service PID = 22039
05-13 18:30:58 I/AssistantTestUtils: Waiting for assistant to be ready...
05-13 18:30:58 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:30:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:30:58 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:30:58 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:30:58 I/DeviceStateMonitor: Waiting 359924 ms for device SKBFXYZO0C1V package manager
05-13 18:30:58 I/NativeDeviceStateMonitor: Waiting 359827 ms for device SKBFXYZO0C1V external store
05-13 18:31:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
05-13 18:31:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
05-13 18:31:01 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:01 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:02 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:02 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:02 I/DeviceStateMonitor: Waiting 359907 ms for device SKBFXYZO0C1V package manager
05-13 18:31:02 I/NativeDeviceStateMonitor: Waiting 359729 ms for device SKBFXYZO0C1V external store
05-13 18:31:04 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
05-13 18:31:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
05-13 18:31:05 D/AssistantTestUtils: Assistant successfully launched: true
05-13 18:31:05 D/RunUtil: Running command [logcat -d -T 1715592656.000 | grep -E 'AssistantWrapper(Impl)?: Assistant is now running.'] without timeout.
05-13 18:31:05 I/AssistantTestUtils: Assistant ready: true
05-13 18:31:05 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:06 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:06 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:06 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
05-13 18:31:06 I/NativeDeviceStateMonitor: Waiting 359847 ms for device SKBFXYZO0C1V external store
05-13 18:31:08 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
05-13 18:31:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
05-13 18:31:09 D/AssistantTestUtils: Assistant successfully launched: true
05-13 18:31:09 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 18:31:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
05-13 18:31:10 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
05-13 18:31:11 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:11 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:11 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:11 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:11 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYZO0C1V package manager
05-13 18:31:11 I/NativeDeviceStateMonitor: Waiting 359867 ms for device SKBFXYZO0C1V external store
05-13 18:31:13 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
05-13 18:31:14 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:14 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:14 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:14 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:14 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:14 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYZO0C1V package manager
05-13 18:31:14 I/NativeDeviceStateMonitor: Waiting 359892 ms for device SKBFXYZO0C1V external store
05-13 18:31:16 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
05-13 18:31:16 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:16 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:16 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:17 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:17 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:17 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
05-13 18:31:17 I/NativeDeviceStateMonitor: Waiting 359872 ms for device SKBFXYZO0C1V external store
05-13 18:31:19 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
05-13 18:31:19 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:19 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:19 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:20 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:20 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:20 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
05-13 18:31:20 I/NativeDeviceStateMonitor: Waiting 359866 ms for device SKBFXYZO0C1V external store
05-13 18:31:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
05-13 18:31:22 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:22 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
05-13 18:31:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
05-13 18:31:22 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
05-13 18:31:22 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
05-13 18:31:23 I/DeviceStateMonitor: Waiting 359923 ms for device SKBFXYZO0C1V package manager
05-13 18:31:23 I/NativeDeviceStateMonitor: Waiting 359761 ms for device SKBFXYZO0C1V external store
05-13 18:31:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
05-13 18:31:25 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:25 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
05-13 18:31:26 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
05-13 18:31:26 D/AssistantTestUtils: Assistant setup not triggered after 5 retries. Current activity: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
05-13 18:31:26 I/AssistantTestUtils: Assistant OOBE skipping required: false
05-13 18:31:26 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 18:31:26 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
05-13 18:31:27 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
05-13 19:10:04 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
05-13 19:10:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
05-13 19:10:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.sdk] with timeout: 45s
05-13 19:10:04 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 19:10:05 D/PrettyTestEventLogger: 
==================== com.google.android.assistant.tvts.AssistantPluginHandler#runAssistantLatencyTests ENDED: Mon May 13 19:10:05 KST 2024 ====================
